[INFO] cloning repository https://github.com/mlamp/magpie
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/mlamp/magpie" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmlamp%2Fmagpie", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmlamp%2Fmagpie'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 7f6c4a550ebb651741dd0e76da1ca355b56ffe4a
[INFO] testing mlamp/magpie against master#f9988fefd3add01f414f52b414308e7872622fee for pr-155114
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fmlamp%2Fmagpie" "/workspace/builds/worker-0-tc1/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-0-tc1/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/mlamp/magpie
[INFO] finished tweaking git repo https://github.com/mlamp/magpie
[INFO] tweaked toml for git repo https://github.com/mlamp/magpie written to /workspace/builds/worker-0-tc1/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/mlamp/magpie on toolchain f9988fefd3add01f414f52b414308e7872622fee
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/mlamp/magpie already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] [stderr]     Updating crates.io index
[INFO] [stderr]  Downloading crates ...
[INFO] [stderr]   Downloaded wait-timeout v0.2.1
[INFO] [stderr]   Downloaded rusty-fork v0.3.1
[INFO] [stderr]   Downloaded proptest v1.11.0
[INFO] [stderr]   Downloaded rustls-webpki v0.103.13
[INFO] [stderr]   Downloaded hyper-rustls v0.27.8
[INFO] [stderr]   Downloaded rustc-demangle v0.1.27
[INFO] [stderr]   Downloaded terminal_size v0.4.4
[INFO] [stderr]   Downloaded mintex v0.1.4
[INFO] [stderr]   Downloaded smart-default v0.7.1
[INFO] [stderr]   Downloaded lazy-regex v3.6.0
[INFO] [stderr]   Downloaded inventory v0.3.24
[INFO] [stderr]   Downloaded globwalk v0.9.1
[INFO] [stderr]   Downloaded typed-builder-macro v0.15.2
[INFO] [stderr]   Downloaded sealed v0.5.0
[INFO] [stderr]   Downloaded lazy-regex-proc_macros v3.6.0
[INFO] [stderr]   Downloaded cucumber v0.21.1
[INFO] [stderr]   Downloaded peg v0.6.3
[INFO] [stderr]   Downloaded unicode-linebreak v0.1.5
[INFO] [stderr]   Downloaded peg-runtime v0.6.3
[INFO] [stderr]   Downloaded drain_filter_polyfill v0.1.3
[INFO] [stderr]   Downloaded nom_locate v4.2.0
[INFO] [stderr]   Downloaded synthez v0.3.1
[INFO] [stderr]   Downloaded gherkin v0.14.0
[INFO] [stderr]   Downloaded peg-macros v0.6.3
[INFO] [stderr]   Downloaded cucumber-codegen v0.21.1
[INFO] [stderr]   Downloaded synthez-core v0.3.1
[INFO] [stderr]   Downloaded textwrap v0.16.2
[INFO] [stderr]   Downloaded tokio v1.51.1
[INFO] [stderr]   Downloaded typed-builder v0.15.2
[INFO] [stderr]   Downloaded cucumber-expressions v0.3.0
[INFO] [stderr]   Downloaded synthez-codegen v0.3.1
[INFO] [stderr]   Downloaded smawk v0.3.2
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] b085a32ec3d5c93736f91869ffd7e14123f2c49545f19a35a29d6d865823c223
[INFO] running `Command { std: "docker" "start" "-a" "b085a32ec3d5c93736f91869ffd7e14123f2c49545f19a35a29d6d865823c223", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "b085a32ec3d5c93736f91869ffd7e14123f2c49545f19a35a29d6d865823c223", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "b085a32ec3d5c93736f91869ffd7e14123f2c49545f19a35a29d6d865823c223", kill_on_drop: false }`
[INFO] [stdout] b085a32ec3d5c93736f91869ffd7e14123f2c49545f19a35a29d6d865823c223
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 3f0de2ed56ecf5df3327ef7df5f3c35eef919339a1d7e25cefce4cb008e69d1a
[INFO] running `Command { std: "docker" "start" "-a" "3f0de2ed56ecf5df3327ef7df5f3c35eef919339a1d7e25cefce4cb008e69d1a", kill_on_drop: false }`
[INFO] [stderr]    Compiling libc v0.2.185
[INFO] [stderr]    Compiling once_cell v1.21.4
[INFO] [stderr]    Compiling http v1.4.0
[INFO] [stderr]    Compiling cc v1.2.60
[INFO] [stderr]    Compiling zerovec-derive v0.11.3
[INFO] [stderr]    Compiling tokio-macros v2.7.0
[INFO] [stderr]    Compiling zerofrom-derive v0.1.7
[INFO] [stderr]    Compiling yoke-derive v0.8.2
[INFO] [stderr]    Compiling writeable v0.6.3
[INFO] [stderr]    Compiling litemap v0.8.2
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling icu_properties_data v2.2.0
[INFO] [stderr]    Compiling icu_normalizer_data v2.2.0
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling http-body v1.0.1
[INFO] [stderr]    Compiling hashbrown v0.17.0
[INFO] [stderr]    Compiling zerofrom v0.1.7
[INFO] [stderr]    Compiling crypto-common v0.1.7
[INFO] [stderr]    Compiling rustls v0.23.38
[INFO] [stderr]    Compiling mio v1.2.0
[INFO] [stderr]    Compiling socket2 v0.6.3
[INFO] [stderr]    Compiling ring v0.17.14
[INFO] [stderr]    Compiling getrandom v0.2.17
[INFO] [stderr]    Compiling yoke v0.8.2
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling digest v0.10.7
[INFO] [stderr]    Compiling futures-channel v0.3.32
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling zerovec v0.11.6
[INFO] [stderr]    Compiling zerotrie v0.2.4
[INFO] [stderr]    Compiling tokio v1.51.1
[INFO] [stderr]    Compiling indexmap v2.14.0
[INFO] [stderr]    Compiling magpie-bt-bencode v0.1.3 (/opt/rustwide/workdir/crates/magpie-bt-bencode)
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling sha1 v0.10.6
[INFO] [stderr]    Compiling tinystr v0.8.3
[INFO] [stderr]    Compiling potential_utf v0.1.5
[INFO] [stderr]    Compiling sha2 v0.10.9
[INFO] [stderr]    Compiling icu_locale_core v2.2.0
[INFO] [stderr]    Compiling icu_collections v2.2.0
[INFO] [stderr]    Compiling http-body-util v0.1.3
[INFO] [stderr]    Compiling socket2 v0.5.10
[INFO] [stderr]    Compiling magpie-bt-metainfo v0.1.3 (/opt/rustwide/workdir/crates/magpie-bt-metainfo)
[INFO] [stderr]    Compiling icu_provider v2.2.0
[INFO] [stderr]    Compiling icu_normalizer v2.2.0
[INFO] [stderr]    Compiling icu_properties v2.2.0
[INFO] [stderr]    Compiling idna_adapter v1.2.1
[INFO] [stderr]    Compiling idna v1.1.0
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling rustls-webpki v0.103.13
[INFO] [stderr]    Compiling url v2.5.8
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling magpie-bt-dht v0.1.3 (/opt/rustwide/workdir/crates/magpie-bt-dht)
[INFO] [stderr]    Compiling tower-http v0.6.8
[INFO] [stderr]    Compiling h2 v0.4.13
[INFO] [stderr]    Compiling magpie-bt-wire v0.1.3 (/opt/rustwide/workdir/crates/magpie-bt-wire)
[INFO] [stderr]    Compiling hyper v1.9.0
[INFO] [stderr]    Compiling tokio-rustls v0.26.4
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling hyper-rustls v0.27.8
[INFO] [stderr]    Compiling reqwest v0.12.28
[INFO] [stderr]    Compiling magpie-bt-core v0.1.3 (/opt/rustwide/workdir/crates/magpie-bt-core)
[INFO] [stderr]    Compiling magpie-bt v0.1.3 (/opt/rustwide/workdir/crates/magpie-bt)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 20s
[INFO] running `Command { std: "docker" "inspect" "3f0de2ed56ecf5df3327ef7df5f3c35eef919339a1d7e25cefce4cb008e69d1a", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "3f0de2ed56ecf5df3327ef7df5f3c35eef919339a1d7e25cefce4cb008e69d1a", kill_on_drop: false }`
[INFO] [stdout] 3f0de2ed56ecf5df3327ef7df5f3c35eef919339a1d7e25cefce4cb008e69d1a
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 0898b8e654f13c2995e8f242d876abee58aaaafe069cfe5305f4dd6b7ec43f7b
[INFO] running `Command { std: "docker" "start" "-a" "0898b8e654f13c2995e8f242d876abee58aaaafe069cfe5305f4dd6b7ec43f7b", kill_on_drop: false }`
[INFO] [stderr]    Compiling rustix v1.1.4
[INFO] [stderr]    Compiling errno v0.3.14
[INFO] [stderr]    Compiling aho-corasick v1.1.4
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling zerocopy v0.8.48
[INFO] [stderr]    Compiling futures-util v0.3.32
[INFO] [stderr]    Compiling anstyle-parse v1.0.0
[INFO] [stderr]    Compiling signal-hook-registry v1.4.8
[INFO] [stderr]    Compiling zerocopy-derive v0.8.48
[INFO] [stderr]    Compiling anstyle v1.0.14
[INFO] [stderr]    Compiling colorchoice v1.0.5
[INFO] [stderr]    Compiling tokio v1.51.1
[INFO] [stderr]    Compiling anstream v1.0.0
[INFO] [stderr]    Compiling clap_lex v1.1.0
[INFO] [stderr]    Compiling clap_derive v4.6.0
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling rand_core v0.9.5
[INFO] [stderr]    Compiling regex-automata v0.4.14
[INFO] [stderr]    Compiling terminal_size v0.4.4
[INFO] [stderr]    Compiling clap_builder v4.6.0
[INFO] [stderr]    Compiling getrandom v0.4.2
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling fastrand v2.4.1
[INFO] [stderr]    Compiling tempfile v3.27.0
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling quick-error v1.2.3
[INFO] [stderr]    Compiling bit-vec v0.8.0
[INFO] [stderr]    Compiling rusty-fork v0.3.1
[INFO] [stderr]    Compiling rand_xorshift v0.4.0
[INFO] [stderr]    Compiling rand v0.9.4
[INFO] [stderr]    Compiling unarray v0.1.4
[INFO] [stderr]    Compiling bit-set v0.8.0
[INFO] [stderr]    Compiling magpie-bt-metainfo v0.1.3 (/opt/rustwide/workdir/crates/magpie-bt-metainfo)
[INFO] [stderr]    Compiling itertools v0.10.5
[INFO] [stderr]    Compiling is-terminal v0.4.17
[INFO] [stderr]    Compiling oorandom v11.1.5
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling memchr v2.8.0
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling tinytemplate v1.2.1
[INFO] [stderr]    Compiling clap v4.6.0
[INFO] [stderr]    Compiling tokio-util v0.7.18
[INFO] [stderr]    Compiling criterion-plot v0.5.0
[INFO] [stderr]    Compiling tokio-rustls v0.26.4
[INFO] [stderr]    Compiling h2 v0.4.13
[INFO] [stderr]    Compiling magpie-bt-wire v0.1.3 (/opt/rustwide/workdir/crates/magpie-bt-wire)
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling half v2.7.1
[INFO] [stderr]    Compiling ciborium-ll v0.2.2
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling ciborium v0.2.2
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling proptest v1.11.0
[INFO] [stderr]    Compiling tower-http v0.6.8
[INFO] [stderr]    Compiling crossbeam-utils v0.8.21
[INFO] [stderr]    Compiling criterion v0.5.1
[INFO] [stderr]    Compiling sealed v0.5.0
[INFO] [stderr]    Compiling synthez-core v0.3.1
[INFO] [stderr]    Compiling crossbeam-epoch v0.9.18
[INFO] [stderr]    Compiling nom v7.1.3
[INFO] [stderr]    Compiling derive_more v0.99.20
[INFO] [stderr]    Compiling bstr v1.12.1
[INFO] [stderr]    Compiling hyper v1.9.0
[INFO] [stderr]    Compiling regex-syntax v0.7.5
[INFO] [stderr]    Compiling peg-runtime v0.6.3
[INFO] [stderr]    Compiling nom_locate v4.2.0
[INFO] [stderr]    Compiling peg-macros v0.6.3
[INFO] [stderr]    Compiling gherkin v0.14.0
[INFO] [stderr]    Compiling globset v0.4.18
[INFO] [stderr]    Compiling hyper-util v0.1.20
[INFO] [stderr]    Compiling synthez-codegen v0.3.1
[INFO] [stderr]    Compiling crossbeam-deque v0.8.6
[INFO] [stderr]    Compiling typed-builder-macro v0.15.2
[INFO] [stderr]    Compiling thiserror-impl v1.0.69
[INFO] [stderr]    Compiling smawk v0.3.2
[INFO] [stderr]    Compiling unicode-linebreak v0.1.5
[INFO] [stderr]    Compiling textwrap v0.16.2
[INFO] [stderr]    Compiling cucumber-expressions v0.3.0
[INFO] [stderr]    Compiling hyper-rustls v0.27.8
[INFO] [stderr]    Compiling typed-builder v0.15.2
[INFO] [stderr]    Compiling reqwest v0.12.28
[INFO] [stderr]    Compiling thiserror v1.0.69
[INFO] [stderr]    Compiling peg v0.6.3
[INFO] [stderr]    Compiling ignore v0.4.25
[INFO] [stderr]    Compiling synthez v0.3.1
[INFO] [stderr]    Compiling lazy-regex-proc_macros v3.6.0
[INFO] [stderr]    Compiling futures-executor v0.3.32
[INFO] [stderr]    Compiling itertools v0.13.0
[INFO] [stderr]    Compiling inflections v1.1.1
[INFO] [stderr]    Compiling futures v0.3.32
[INFO] [stderr]    Compiling console v0.15.11
[INFO] [stderr]    Compiling lazy-regex v3.6.0
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling smart-default v0.7.1
[INFO] [stderr]    Compiling globwalk v0.9.1
[INFO] [stderr]    Compiling inventory v0.3.24
[INFO] [stderr]    Compiling drain_filter_polyfill v0.1.3
[INFO] [stderr]    Compiling linked-hash-map v0.5.6
[INFO] [stderr]    Compiling tracing-subscriber v0.3.23
[INFO] [stderr]    Compiling magpie-bt-dht v0.1.3 (/opt/rustwide/workdir/crates/magpie-bt-dht)
[INFO] [stderr]    Compiling magpie-bt-bencode v0.1.3 (/opt/rustwide/workdir/crates/magpie-bt-bencode)
[INFO] [stderr]    Compiling magpie-bt-core v0.1.3 (/opt/rustwide/workdir/crates/magpie-bt-core)
[INFO] [stderr]    Compiling cucumber-codegen v0.21.1
[INFO] [stderr]    Compiling cucumber v0.21.1
[INFO] [stderr]    Compiling magpie-bt v0.1.3 (/opt/rustwide/workdir/crates/magpie-bt)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 3m 54s
[INFO] running `Command { std: "docker" "inspect" "0898b8e654f13c2995e8f242d876abee58aaaafe069cfe5305f4dd6b7ec43f7b", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0898b8e654f13c2995e8f242d876abee58aaaafe069cfe5305f4dd6b7ec43f7b", kill_on_drop: false }`
[INFO] [stdout] 0898b8e654f13c2995e8f242d876abee58aaaafe069cfe5305f4dd6b7ec43f7b
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-0-tc1/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+f9988fefd3add01f414f52b414308e7872622fee" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] d5dfe86310ddf5b2a5a8e4207724a3a77f0cfd4e2f6ddaa7ed838529845382f5
[INFO] running `Command { std: "docker" "start" "-a" "d5dfe86310ddf5b2a5a8e4207724a3a77f0cfd4e2f6ddaa7ed838529845382f5", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.70s
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/magpie_bt-707bb11179ee63e0)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/cucumber.rs (/opt/rustwide/target/debug/deps/cucumber-b23dd694555819ca)
[INFO] [stdout] Feature: BEP 3 — core BitTorrent protocol
[INFO] [stdout]   Scenario: handshake round-trips with cleared reserved bits
[INFO] [stdout]    ✔  Given a handshake with info-hash "AA" repeated 20 times and peer-id "BB" repeated 20 times
[INFO] [stdout]    ✔  When the handshake is encoded then decoded
[INFO] [stdout]    ✔  Then the decoded info-hash matches and the Fast extension bit is unset
[INFO] [stdout]   Scenario: Have message round-trips through the wire codec
[INFO] [stdout]    ✔  Given a Have message for piece 42
[INFO] [stdout]    ✔  When the wire codec encodes and decodes it
[INFO] [stdout]    ✔  Then the decoded piece index is 42
[INFO] [stdout]   Scenario: Request message canonical layout
[INFO] [stdout]    ✔  Given a Request for piece 1, offset 16384, length 16384
[INFO] [stdout]    ✔  When the wire codec encodes it
[INFO] [stdout]    ✔  Then the resulting frame begins with length-prefix 13 and message id 6
[INFO] [stdout]   Scenario: Bitfield round-trip preserves payload bytes
[INFO] [stdout]    ✔  Given a Bitfield containing bytes "FF 0F"
[INFO] [stdout]    ✔  When the wire codec encodes and decodes it
[INFO] [stdout]    ✔  Then the decoded bitfield bytes equal "FF 0F"
[INFO] [stdout] Feature: BEP 6 — Fast extension
[INFO] [stdout]   Scenario: handshake advertises Fast extension via reserved byte 7 bit 0x04
[INFO] [stdout]    ✔  Given a handshake with the Fast extension bit set
[INFO] [stdout]    ✔  When the handshake is encoded then decoded
[INFO] [stdout]    ✔  Then the Fast extension bit is set
[INFO] [stdout]   Scenario: HaveAll round-trips as a single-byte payload frame
[INFO] [stdout]    ✔  Given a HaveAll message
[INFO] [stdout]    ✔  When the wire codec encodes and decodes it
[INFO] [stdout]    ✔  Then the decoded message is HaveAll
[INFO] [stdout]   Scenario: HaveNone round-trips as a single-byte payload frame
[INFO] [stdout]    ✔  Given a HaveNone message
[INFO] [stdout]    ✔  When the wire codec encodes and decodes it
[INFO] [stdout]    ✔  Then the decoded message is HaveNone
[INFO] [stdout]   Scenario: AllowedFast round-trips with a single piece index
[INFO] [stdout]    ✔  Given an AllowedFast message for piece 99
[INFO] [stdout]    ✔  When the wire codec encodes and decodes it
[INFO] [stdout]    ✔  Then the decoded AllowedFast piece index is 99
[INFO] [stdout]   Scenario: RejectRequest round-trips with the rejected block address
[INFO] [stdout]    ✔  Given a RejectRequest for piece 1, offset 16384, length 16384
[INFO] [stdout]    ✔  When the wire codec encodes and decodes it
[INFO] [stdout]    ✔  Then the decoded RejectRequest matches the original block address
[INFO] [stdout] Feature: BEP 9 — Extension for peers to send metadata (ut_metadata)
[INFO] [stdout]   Scenario: Request message round-trips through the codec
[INFO] [stdout]    ?  Given a MetadataMessage::Request for piece 7
[INFO] [stdout]       Step skipped: tests/features/bep-0009-metadata.feature:5:5
[INFO] [stdout]   Scenario: Reject message round-trips through the codec
[INFO] [stdout]    ?  Given a MetadataMessage::Reject for piece 42
[INFO] [stdout]       Step skipped: tests/features/bep-0009-metadata.feature:10:5
[INFO] [stdout]   Scenario: Data message carries the bencoded dict followed by raw metadata bytes
[INFO] [stdout]    ?  Given a MetadataMessage::Data for piece 0 with total_size 32000 and payload "raw metadata bytes here!"
[INFO] [stdout]       Step skipped: tests/features/bep-0009-metadata.feature:15:5
[INFO] [stdout]   Scenario: Last piece may carry zero trailing bytes
[INFO] [stdout]    ?  Given a MetadataMessage::Data for piece 5 with total_size 81920 and an empty payload
[INFO] [stdout]       Step skipped: tests/features/bep-0009-metadata.feature:21:5
[INFO] [stdout]   Scenario: Decoder rejects msg_type values other than 0, 1, 2
[INFO] [stdout]    ?  Given a bencoded payload with msg_type=99
[INFO] [stdout]       Step skipped: tests/features/bep-0009-metadata.feature:26:5
[INFO] [stdout]   Scenario: Decoder rejects total_size above the 16 MB safety cap
[INFO] [stdout]    ?  Given a Data payload declaring total_size 20_000_000
[INFO] [stdout]       Step skipped: tests/features/bep-0009-metadata.feature:31:5
[INFO] [stdout]   Scenario: Decoder rejects per-piece payload above 16 KiB
[INFO] [stdout]    ?  Given a Data payload whose trailing bytes exceed METADATA_PIECE_SIZE (16384)
[INFO] [stdout]       Step skipped: tests/features/bep-0009-metadata.feature:36:5
[INFO] [stdout]   Scenario: metadata_piece_count derives the 16 KiB piece count from total size
[INFO] [stdout]    ?  Given a metadata size of 16383 bytes
[INFO] [stdout]       Step skipped: tests/features/bep-0009-metadata.feature:41:5
[INFO] [stdout]   Scenario: Seeder advertises metadata_size in its extension handshake
[INFO] [stdout]    ?  Given a torrent session constructed with known info bytes
[INFO] [stdout]       Step skipped: tests/features/bep-0009-metadata.feature:47:5
[INFO] [stdout]   Scenario: Magnet add fetches metadata, verifies SHA-1, then transitions to downloading
[INFO] [stdout]    ?  Given two engines on loopback where the seeder holds full metadata + content
[INFO] [stdout]       Step skipped: tests/features/bep-0009-metadata.feature:52:5
[INFO] [stdout] Feature: BEP 10 — Extension Protocol
[INFO] [stdout]   Scenario: Handshake advertises BEP 10 via reserved byte 5 bit 0x10
[INFO] [stdout]    ?  Given a handshake with the extension-protocol bit set
[INFO] [stdout]       Step skipped: tests/features/bep-0010-extension.feature:5:5
[INFO] [stdout]   Scenario: Extension handshake round-trips its m dict and supplemental fields
[INFO] [stdout]    ?  Given an ExtensionHandshake with m={"ut_metadata":1,"ut_pex":2}, metadata_size=31415, client="test-client"
[INFO] [stdout]       Step skipped: tests/features/bep-0010-extension.feature:10:5
[INFO] [stdout]   Scenario: Decoder is lenient about unknown keys
[INFO] [stdout]    ?  Given a handshake bencode payload containing a key the decoder does not recognise
[INFO] [stdout]       Step skipped: tests/features/bep-0010-extension.feature:17:5
[INFO] [stdout]   Scenario: Decoder rejects a non-dict payload
[INFO] [stdout]    ?  Given an extension-handshake payload that is not a bencoded dict
[INFO] [stdout]       Step skipped: tests/features/bep-0010-extension.feature:22:5
[INFO] [stdout]   Scenario: Extension IDs of 0 are reserved for the handshake itself
[INFO] [stdout]    ?  Given an inbound Message::Extended with id=0 after the initial handshake
[INFO] [stdout]       Step skipped: tests/features/bep-0010-extension.feature:27:5
[INFO] [stdout]   Scenario: Per-peer ExtensionRegistry tracks local and remote IDs independently
[INFO] [stdout]    ?  Given a registry constructed with local m={"ut_metadata":1,"ut_pex":2}
[INFO] [stdout]       Step skipped: tests/features/bep-0010-extension.feature:32:5
[INFO] [stdout]   Scenario: PeerConn exchanges extension handshakes immediately after the BEP 3 handshake
[INFO] [stdout]    ?  Given a connected pair of peers each advertising the BEP 10 reserved bit
[INFO] [stdout]       Step skipped: tests/features/bep-0010-extension.feature:39:5
[INFO] [stdout]   Scenario: Inbound extended payload size is capped at 1 MB
[INFO] [stdout]    ?  Given an inbound Message::Extended with payload exceeding 1 MB
[INFO] [stdout]       Step skipped: tests/features/bep-0010-extension.feature:46:5
[INFO] [stdout] Feature: BEP 11 — Peer Exchange (ut_pex)
[INFO] [stdout]   Scenario: PexMessage round-trips IPv4 added/dropped with per-peer flags
[INFO] [stdout]    ?  Given a PexMessage with two IPv4 added peers (one SEED, one REACHABLE) and one IPv4 dropped peer
[INFO] [stdout]       Step skipped: tests/features/bep-0011-pex.feature:5:5
[INFO] [stdout]   Scenario: PexMessage round-trips IPv6 added/dropped with per-peer flags
[INFO] [stdout]    ?  Given a PexMessage with one IPv6 added peer (SUPPORTS_UTP) and one IPv6 dropped peer
[INFO] [stdout]       Step skipped: tests/features/bep-0011-pex.feature:10:5
[INFO] [stdout]   Scenario: PexMessage round-trips a mixed v4/v6 added+dropped batch
[INFO] [stdout]    ?  Given a PexMessage carrying both IPv4 and IPv6 peers in added and dropped
[INFO] [stdout]       Step skipped: tests/features/bep-0011-pex.feature:15:5
[INFO] [stdout]   Scenario: Missing added.f bytes default each peer's flags to 0
[INFO] [stdout]    ?  Given a wire payload with one IPv4 added peer and no "added.f" key
[INFO] [stdout]       Step skipped: tests/features/bep-0011-pex.feature:20:5
[INFO] [stdout]   Scenario: Decoder rejects added.f length that does not match the peer count
[INFO] [stdout]    ?  Given a payload with two IPv4 added peers and only one byte of added.f
[INFO] [stdout]       Step skipped: tests/features/bep-0011-pex.feature:25:5
[INFO] [stdout]   Scenario: Decoder rejects compact peer lists with truncated stride
[INFO] [stdout]    ?  Given a payload whose "added" bytes length is not a multiple of 6
[INFO] [stdout]       Step skipped: tests/features/bep-0011-pex.feature:30:5
[INFO] [stdout]   Scenario: Decoder rejects messages exceeding MAX_PEX_PEERS (200)
[INFO] [stdout]    ?  Given a payload carrying MAX_PEX_PEERS + 1 added peers
[INFO] [stdout]       Step skipped: tests/features/bep-0011-pex.feature:35:5
[INFO] [stdout]   Scenario: Outbound PEX is throttled to once every 60s per peer
[INFO] [stdout]    ?  Given a torrent that has just sent a PEX round to peer P
[INFO] [stdout]       Step skipped: tests/features/bep-0011-pex.feature:40:5
[INFO] [stdout]   Scenario: Inbound PEX from the same peer is rate-limited to once every 10s
[INFO] [stdout]    ?  Given a torrent that just accepted a PEX message from peer P
[INFO] [stdout]       Step skipped: tests/features/bep-0011-pex.feature:45:5
[INFO] [stdout]   Scenario: Outbound PEX is diff-based and capped per round
[INFO] [stdout]    ?  Given a peer set that has gained 80 peers and lost 60 peers since the last PEX
[INFO] [stdout]       Step skipped: tests/features/bep-0011-pex.feature:50:5
[INFO] [stdout]   Scenario: Private torrents must not exchange PEX
[INFO] [stdout]    ?  Given a torrent session whose info dict carries "private": 1
[INFO] [stdout]       Step skipped: tests/features/bep-0011-pex.feature:55:5
[INFO] [stdout]   Scenario: PEX-discovered peers are surfaced to the engine
[INFO] [stdout]    ?  Given a torrent that received an inbound PEX with two new added peers
[INFO] [stdout]       Step skipped: tests/features/bep-0011-pex.feature:60:5
[INFO] [stdout] Feature: BEP 12 — multi-tracker with tier fall-through
[INFO] [stdout]   Scenario: announce falls through tiers on failure
[INFO] [stdout]    ✔  Given a TieredTracker with tier-0 containing a failing tracker
[INFO] [stdout]    ✔  And tier-1 containing a working tracker that returns 2 peers
[INFO] [stdout]    ✔  When announce is called
[INFO] [stdout]    ✔  Then the peer list contains 2 peers
[INFO] [stdout]   Scenario: successful tracker is promoted to tier head
[INFO] [stdout]    ✔  Given a TieredTracker with tier-0 [failing, working] in that order
[INFO] [stdout]    ✔  When announce is called successfully
[INFO] [stdout]    ✔  Then the tier-0 order becomes [working, failing]
[INFO] [stdout]   Scenario: all trackers failing returns the last error
[INFO] [stdout]    ✔  Given a TieredTracker where every tracker in every tier fails
[INFO] [stdout]    ✔  When announce is called
[INFO] [stdout]    ✔  Then the call returns the last observed TrackerError
[INFO] [stdout]   Scenario: shuffled tiers are deterministic under a fixed seed
[INFO] [stdout]    ✔  Given two TieredTracker instances built from the same tiers and seed
[INFO] [stdout]    ✔  When tier_order is inspected on both
[INFO] [stdout]    ✔  Then the orderings are identical
[INFO] [stdout] Feature: BEP 14 — Local Service Discovery
[INFO] [stdout]   Scenario: LsdAnnounce round-trips a single info hash with cookie
[INFO] [stdout]    ?  Given an LsdAnnounce for port 6881 with one info hash and cookie "test-cookie"
[INFO] [stdout]       Step skipped: tests/features/bep-0014-lsd.feature:5:5
[INFO] [stdout]   Scenario: LsdAnnounce round-trips multiple info hashes
[INFO] [stdout]    ?  Given an LsdAnnounce carrying two info hashes
[INFO] [stdout]       Step skipped: tests/features/bep-0014-lsd.feature:10:5
[INFO] [stdout]   Scenario: Decoder accepts the canonical CRLF wire format
[INFO] [stdout]    ?  Given the wire bytes "BT-SEARCH * HTTP/1.1\r\nHost: 239.192.152.143:6771\r\nPort: 6881\r\nInfohash: <40-hex>\r\ncookie: abc123\r\n\r\n"
[INFO] [stdout]       Step skipped: tests/features/bep-0014-lsd.feature:15:5
[INFO] [stdout]   Scenario: Decoder is lenient about LF-only and mixed line endings
[INFO] [stdout]    ?  Given the same announce serialised with bare LF separators
[INFO] [stdout]       Step skipped: tests/features/bep-0014-lsd.feature:20:5
[INFO] [stdout]   Scenario: Decoder rejects messages missing the Port header
[INFO] [stdout]    ?  Given a wire message with Infohash but no Port header
[INFO] [stdout]       Step skipped: tests/features/bep-0014-lsd.feature:25:5
[INFO] [stdout]   Scenario: Decoder rejects messages missing any Infohash header
[INFO] [stdout]    ?  Given a wire message with Port but no Infohash header
[INFO] [stdout]       Step skipped: tests/features/bep-0014-lsd.feature:30:5
[INFO] [stdout]   Scenario: Decoder rejects malformed info hashes
[INFO] [stdout]    ?  Given a wire message whose Infohash value is not 40 lowercase-hex chars
[INFO] [stdout]       Step skipped: tests/features/bep-0014-lsd.feature:35:5
[INFO] [stdout]   Scenario: Decoder rejects messages whose request line is not BT-SEARCH
[INFO] [stdout]    ?  Given a wire message starting with "GET / HTTP/1.1"
[INFO] [stdout]       Step skipped: tests/features/bep-0014-lsd.feature:40:5
[INFO] [stdout]   Scenario: Decoder caps the number of info hashes per message
[INFO] [stdout]    ?  Given a wire message containing more than 100 Infohash headers
[INFO] [stdout]       Step skipped: tests/features/bep-0014-lsd.feature:45:5
[INFO] [stdout]   Scenario: LsdService binds to INADDR_ANY with SO_REUSEADDR so multiple listeners coexist
[INFO] [stdout]    ?  Given two LsdService bind attempts on the same multicast port
[INFO] [stdout]       Step skipped: tests/features/bep-0014-lsd.feature:50:5
[INFO] [stdout]   Scenario: LsdService's outbound announce uses TTL=1 and the configured multicast group
[INFO] [stdout]    ?  Given a default LsdConfig
[INFO] [stdout]       Step skipped: tests/features/bep-0014-lsd.feature:55:5
[INFO] [stdout]   Scenario: LsdService filters its own announcements via the cookie field
[INFO] [stdout]    ?  Given an LsdService that emitted an announce with cookie C
[INFO] [stdout]       Step skipped: tests/features/bep-0014-lsd.feature:60:5
[INFO] [stdout]   Scenario: Private torrents are never registered for LSD announce
[INFO] [stdout]    ?  Given an LsdService handle and a private info hash
[INFO] [stdout]       Step skipped: tests/features/bep-0014-lsd.feature:65:5
[INFO] [stdout]   Scenario: Two engines on loopback discover each other via multicast
[INFO] [stdout]    ?  Given engine A registers info hash H and engine B is listening on the same loopback multicast group
[INFO] [stdout]       Step skipped: tests/features/bep-0014-lsd.feature:70:5
[INFO] [stdout] Feature: BEP 15 — UDP tracker protocol
[INFO] [stdout]   Scenario: CONNECT request carries the protocol magic first
[INFO] [stdout]    ✔  Given a fresh transaction_id 0xDEADBEEF
[INFO] [stdout]    ✔  When a CONNECT request is encoded
[INFO] [stdout]    ✔  Then the first 8 bytes equal 0x0000041727101980
[INFO] [stdout]    ✔  And bytes 8..12 encode action = 0 (CONNECT)
[INFO] [stdout]    ✔  And bytes 12..16 encode transaction_id = 0xDEADBEEF
[INFO] [stdout]   Scenario: CONNECT response must match the sent transaction_id
[INFO] [stdout]    ✔  Given a CONNECT response with transaction_id 0xCAFEBABE
[INFO] [stdout]    ✔  When the client expected transaction_id 0x12345678
[INFO] [stdout]    ✔  Then decoding returns a decode error
[INFO] [stdout]   Scenario: tracker error response propagates as a typed failure
[INFO] [stdout]    ✔  Given a UDP response with action = 3 (ERROR) and body "info_hash unknown"
[INFO] [stdout]    ✔  When the response is decoded
[INFO] [stdout]    ✔  Then decoding returns a tracker failure with message "info_hash unknown"
[INFO] [stdout]   Scenario: ANNOUNCE response peer list decodes from compact IPv4 form
[INFO] [stdout]    ✔  Given an ANNOUNCE response with interval 1800 and two compact IPv4 peers
[INFO] [stdout]    ✔  When the response is decoded
[INFO] [stdout]    ✔  Then the announce interval is 1800 seconds
[INFO] [stdout]    ✔  And the parsed peer list contains "10.0.0.1:6881"
[INFO] [stdout]    ✔  And the parsed peer list contains "192.168.1.5:6881"
[INFO] [stdout]   Scenario: retry timeout follows the BEP 15 curve (15s × 2^n, cap 3840s)
[INFO] [stdout]    ✔  When the retry timeout is computed for attempts 0, 1, 2, 7, 8, 20
[INFO] [stdout]    ✔  Then the timeouts are 15s, 30s, 60s, 1920s, 3840s, 3840s
[INFO] [stdout]   Scenario: connection_id is refreshed every 60 seconds
[INFO] [stdout]    ?  Given a tracker client that observed its last CONNECT reply 61 seconds ago
[INFO] [stdout]       Step skipped: tests/features/bep-0015-udp-tracker.feature:39:5
[INFO] [stdout] Feature: BEP 23 — tracker compact peer list
[INFO] [stdout]   Scenario: parse a tracker response carrying a two-peer compact v4 list
[INFO] [stdout]    ✔  Given a bencoded announce response with interval 1800 and two compact v4 peers
[INFO] [stdout]    ✔  When the tracker response is parsed
[INFO] [stdout]    ✔  Then the announce interval is 1800 seconds
[INFO] [stdout]    ✔  And the parsed peer list contains "10.0.0.1:6881"
[INFO] [stdout]    ✔  And the parsed peer list contains "192.168.1.2:49205"
[INFO] [stdout]   Scenario: parse a tracker response carrying a single compact v6 peer
[INFO] [stdout]    ✔  Given a bencoded announce response with interval 900 and one compact v6 peer at "[::1]:6881"
[INFO] [stdout]    ✔  When the tracker response is parsed
[INFO] [stdout]    ✔  Then the parsed peer list contains "[::1]:6881"
[INFO] [stdout]   Scenario: tracker failure reason surfaces as a typed error
[INFO] [stdout]    ✔  Given a bencoded tracker response with failure reason "tracker down"
[INFO] [stdout]    ✔  When the tracker response is parsed
[INFO] [stdout]    ✔  Then parsing returns a tracker failure with message "tracker down"
[INFO] [stdout] Feature: BEP 27 — private torrents
[INFO] [stdout]   Scenario: private flag is parsed from the info dict
[INFO] [stdout]    ✔  Given a metainfo whose info dict carries "private": 1
[INFO] [stdout]    ✔  When the metainfo is parsed
[INFO] [stdout]    ✔  Then the parsed Info reports private = true
[INFO] [stdout]   Scenario: absent private key is treated as public
[INFO] [stdout]    ✔  Given a metainfo whose info dict omits the "private" key
[INFO] [stdout]    ✔  When the metainfo is parsed
[INFO] [stdout]    ✔  Then the parsed Info reports private = false
[INFO] [stdout]   Scenario: private flag is authoritative at the session layer
[INFO] [stdout]    ✔  Given a torrent session constructed with private = true
[INFO] [stdout]    ✔  When the session reports its private flag via is_private()
[INFO] [stdout]    ✔  Then the value is true
[INFO] [stdout]    ✔  And future peer-discovery subsystems (DHT / PEX / LSD) must not gossip
[INFO] [stdout] [Summary]
[INFO] [stdout] 10 features
[INFO] [stdout] 69 scenarios (24 passed, 45 skipped)
[INFO] [stdout] 124 steps (79 passed, 45 skipped)
[INFO] [stderr]      Running tests/three_engine_swarm.rs (/opt/rustwide/target/debug/deps/three_engine_swarm-595de50946d6561f)
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/magpie_bt_bencode-0fa75822a295cd65)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 31 tests
[INFO] [stdout] test decode::tests::bytes_rejects_length_exceeds_input ... ok
[INFO] [stdout] test decode::tests::bytes_basic ... ok
[INFO] [stdout] test decode::tests::bytes_empty ... ok
[INFO] [stdout] test decode::tests::decode_prefix_returns_remainder ... ok
[INFO] [stdout] test decode::tests::decoded_bytes_borrow_input ... ok
[INFO] [stdout] test decode::tests::bytes_rejects_length_leading_zero ... ok
[INFO] [stdout] test decode::tests::dict_rejects_duplicate ... ok
[INFO] [stdout] test decode::tests::dict_rejects_unsorted ... ok
[INFO] [stdout] test decode::tests::depth_exceeded ... ok
[INFO] [stdout] test decode::tests::dict_sorted_ok ... ok
[INFO] [stdout] test decode::tests::dict_value_span_absent_key ... ok
[INFO] [stdout] test decode::tests::dict_value_span_rejects_non_dict ... ok
[INFO] [stdout] test decode::tests::empty_input ... ok
[INFO] [stdout] test decode::tests::int_rejects_leading_zero ... ok
[INFO] [stdout] test decode::tests::int_negative ... ok
[INFO] [stdout] test decode::tests::int_rejects_double_minus ... ok
[INFO] [stdout] test decode::tests::int_rejects_minus_alone ... ok
[INFO] [stdout] test decode::tests::int_rejects_negative_zero ... ok
[INFO] [stdout] test decode::tests::int_rejects_empty ... ok
[INFO] [stdout] test decode::tests::int_rejects_overflow ... ok
[INFO] [stdout] test decode::tests::int_zero ... ok
[INFO] [stdout] test decode::tests::skip_value_handles_nested ... ok
[INFO] [stdout] test decode::tests::skip_value_rejects_malformed ... ok
[INFO] [stdout] test decode::tests::skip_value_reports_span ... ok
[INFO] [stdout] test decode::tests::trailing_data_rejected ... ok
[INFO] [stdout] test decode::tests::list_of_mixed ... ok
[INFO] [stdout] test encode::tests::encodes_bytes ... ok
[INFO] [stdout] test encode::tests::encodes_dict_in_sorted_order ... ok
[INFO] [stdout] test encode::tests::encodes_int ... ok
[INFO] [stdout] test encode::tests::roundtrip_simple ... ok
[INFO] [stdout] test decode::tests::dict_value_span_finds_info ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 31 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.04s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/proptest.rs (/opt/rustwide/target/debug/deps/proptest-e64e99365b3c92d0)
[INFO] [stdout] 
[INFO] [stdout] running 3 tests
[INFO] [stdout] test decoder_never_panics_on_arbitrary_bytes ... ok
[INFO] [stdout] test encode_decode_roundtrip ... ok
[INFO] [stdout] test decode_encode_byte_identical_on_canonical ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 3 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.24s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/magpie_bt_core-ad14e8db1c8065af)
[INFO] [stdout] 
[INFO] [stdout] running 254 tests
[INFO] [stdout] test alerts::queue::tests::category_mask_filters_push ... ok
[INFO] [stdout] test alerts::queue::tests::dropped_sentinel_resets_after_drain ... ok
[INFO] [stdout] test alerts::queue::tests::mask_always_passes_dropped_sentinel ... ok
[INFO] [stdout] test alerts::queue::tests::overflow_drops_oldest_and_emits_sentinel ... ok
[INFO] [stdout] test alerts::queue::tests::push_drain_roundtrip ... ok
[INFO] [stdout] test alerts::queue::tests::wait_wakes_on_push ... ok
[INFO] [stdout] test lsd::tests::encode_decode_roundtrip ... ok
[INFO] [stdout] test lsd::tests::encode_decode_roundtrip_no_cookie ... ok
[INFO] [stdout] test lsd::tests::invalid_info_hash_error ... ok
[INFO] [stdout] test lsd::tests::invalid_request_line ... ok
[INFO] [stdout] test lsd::tests::lsd_loopback_discovery ... ignored, multicast on loopback may not work in all environments
[INFO] [stdout] test lsd::tests::lenient_line_endings_lf_only ... ok
[INFO] [stdout] test lsd::tests::missing_info_hash_error ... ok
[INFO] [stdout] test lsd::tests::invalid_port_error ... ok
[INFO] [stdout] test lsd::tests::lenient_line_endings_mixed ... ok
[INFO] [stdout] test lsd::tests::missing_port_error ... ok
[INFO] [stdout] test lsd::tests::private_torrent_not_registered ... ok
[INFO] [stdout] test alerts::queue::tests::generation_increments_on_drain ... ok
[INFO] [stdout] test lsd::tests::parse_multiple_info_hashes ... ok
[INFO] [stdout] test lsd::tests::too_many_info_hashes_rejected ... ok
[INFO] [stdout] test lsd::tests::parse_realistic_announce ... ok
[INFO] [stdout] test peer_filter::tests::default_rejects_unspecified_and_multicast_and_broadcast ... ok
[INFO] [stdout] test peer_filter::tests::allows_global_unicast ... ok
[INFO] [stdout] test peer_filter::tests::default_allows_private_by_default ... ok
[INFO] [stdout] test peer_filter::tests::default_rejects_loopback ... ok
[INFO] [stdout] test peer_filter::tests::permissive_allows_loopback ... ok
[INFO] [stdout] test peer_filter::tests::strict_rejects_private ... ok
[INFO] [stdout] test peer_id::tests::suffix_has_reasonable_entropy ... ok
[INFO] [stdout] test picker::tests::endgame_engages_below_threshold ... ok
[INFO] [stdout] test peer_id::tests::layout_matches_azureus ... ok
[INFO] [stdout] test peer_id::tests::prefix_method_matches_built_id ... ok
[INFO] [stdout] test picker::tests::endgame_returns_any_missing_piece ... ok
[INFO] [stdout] test picker::tests::forget_peer_decrements_availability ... ok
[INFO] [stdout] test picker::tests::mark_have_is_idempotent ... ok
[INFO] [stdout] test picker::tests::near_complete_switches_to_endgame ... ok
[INFO] [stdout] test picker::tests::returns_none_when_complete ... ok
[INFO] [stdout] test picker::tests::picks_rarest_piece ... ok
[INFO] [stdout] test picker::tests::skewed_distribution_prefers_rare ... ok
[INFO] [stdout] test session::choker::leech::tests::picks_top_n_by_down_rate ... ok
[INFO] [stdout] test peer_filter::tests::default_rejects_link_local ... ok
[INFO] [stdout] test session::choker::leech::tests::snubbed_peers_excluded ... ok
[INFO] [stdout] test picker::tests::skips_unavailable_pieces_in_normal_mode ... ok
[INFO] [stdout] test peer_filter::tests::default_rejects_zero_port ... ok
[INFO] [stdout] test picker::tests::uniform_distribution_prefers_lowest_index ... ok
[INFO] [stdout] test session::choker::seed::tests::picks_fastest_consumers_first ... ok
[INFO] [stdout] test session::choker::tests::pick_optimistic_picks_at_most_n ... ok
[INFO] [stdout] test session::choker::tests::pick_optimistic_is_deterministic ... ok
[INFO] [stdout] test session::choker::leech::tests::tie_break_deterministic_by_slot_id ... ok
[INFO] [stdout] test session::choker::seed::tests::excludes_uninterested_peers ... ok
[INFO] [stdout] test session::choker::tests::pick_optimistic_returns_nothing_when_no_eligible ... ok
[INFO] [stdout] test session::metadata_exchange::tests::receive_piece_rejects_out_of_range ... ok
[INFO] [stdout] test session::metadata_exchange::tests::receive_piece_stores_data ... ok
[INFO] [stdout] test session::metadata_exchange::tests::pending_prevents_double_request ... ok
[INFO] [stdout] test session::metadata_exchange::tests::set_total_size_allocates_pieces ... ok
[INFO] [stdout] test session::metadata_exchange::tests::reject_clears_pending ... ok
[INFO] [stdout] test session::metadata_exchange::tests::set_total_size_ignores_subsequent_calls ... ok
[INFO] [stdout] test session::metadata_exchange::tests::set_total_size_rejects_oversized ... ok
[INFO] [stdout] test session::disk::tests::surfaces_hash_mismatch ... ok
[INFO] [stdout] test session::metadata_exchange::tests::new_assembler_has_no_size ... ok
[INFO] [stdout] test session::peer_upload::tests::can_submit_read_respects_watermark ... ok
[INFO] [stdout] test session::peer_upload::tests::choke_clears_queue_without_fast_ext ... ok
[INFO] [stdout] test session::peer_upload::tests::cancel_removes_from_both_queues ... ok
[INFO] [stdout] test session::metadata_exchange::tests::try_assemble_concatenates ... ok
[INFO] [stdout] test session::peer_upload::tests::choked_non_fast_rejected_outside_grace ... ok
[INFO] [stdout] test session::peer_upload::tests::duplicate_request_rejected_silently ... ok
[INFO] [stdout] test session::disk::tests::verifies_and_writes ... ok
[INFO] [stdout] test session::peer_upload::tests::fast_ext_choke_retains_allowed_fast ... ok
[INFO] [stdout] test session::peer_upload::tests::fast_set_abuse_triggers_after_cap ... ok
[INFO] [stdout] test session::peer_upload::tests::watermark_clamps_to_max_for_high_rate ... ok
[INFO] [stdout] test session::peer_upload::tests::watermark_clamps_to_min_at_zero_rate ... ok
[INFO] [stdout] test session::peer_upload::tests::in_flight_duplicate_also_rejected ... ok
[INFO] [stdout] test session::peer_upload::tests::watermark_scales_with_rate ... ok
[INFO] [stdout] test session::peer_upload::tests::unchoked_queue_accepts_up_to_cap ... ok
[INFO] [stdout] test session::peer_upload::tests::post_choke_grace_accepts_for_2s ... ok
[INFO] [stdout] test session::pex::tests::empty_diff_returns_none ... ok
[INFO] [stdout] test session::pex::tests::build_message_dropped ... ok
[INFO] [stdout] test session::pex::tests::build_message_caps_at_50 ... ok
[INFO] [stdout] test session::pex::tests::build_message_added ... ok
[INFO] [stdout] test session::pex::tests::peer_disconnected_clears_tracking ... ok
[INFO] [stdout] test session::pex::tests::private_torrent_disables_pex ... ok
[INFO] [stdout] test session::pex::tests::should_send_to_respects_interval ... ok
[INFO] [stdout] test session::pex::tests::public_torrent_enables_pex ... ok
[INFO] [stdout] test session::read_cache::tests::insert_verified_short_circuits_next_read ... ok
[INFO] [stdout] test session::read_cache::tests::bypass_does_not_pollute_cache ... ok
[INFO] [stdout] test session::resume::tests::decode_rejects_missing_fields ... ok
[INFO] [stdout] test session::resume::tests::decode_rejects_non_dict_root ... ok
[INFO] [stdout] test session::read_cache::tests::singleflight_coalesces_concurrent_misses ... ok
[INFO] [stdout] test session::resume::tests::decode_rejects_piece_count_over_max ... ok
[INFO] [stdout] test session::resume::tests::decode_rejects_unsupported_version ... ok
[INFO] [stdout] test session::read_cache::tests::hit_after_load ... ok
[INFO] [stdout] test session::resume::tests::encode_decode_roundtrip ... ok
[INFO] [stdout] test session::resume::tests::decode_rejects_wrong_info_hash_length ... ok
[INFO] [stdout] test session::read_cache::tests::lru_evicts_cold_entry_when_over_budget ... ok
[INFO] [stdout] test session::resume::tests::enqueue_rejects_have_length_mismatch ... ok
[INFO] [stdout] test session::resume::tests::load_missing_sidecar_returns_none ... ok
[INFO] [stdout] test session::resume::tests::file_sink_writes_and_loads_sidecar ... ok
[INFO] [stdout] test session::resume::tests::flush_graceful_is_bounded ... ok
[INFO] [stdout] test session::resume::tests::enqueue_deduplicates_on_info_hash ... ok
[INFO] [stdout] test session::resume::tests::atomic_write_preserves_prior_on_failure ... ok
[INFO] [stdout] test session::resume::tests::pack_exact_byte_boundary ... ok
[INFO] [stdout] test session::resume::tests::pack_unpack_roundtrips ... ok
[INFO] [stdout] test session::resume::tests::pack_empty_is_empty ... ok
[INFO] [stdout] test session::resume::tests::unpack_ignores_trailing_bits ... ok
[INFO] [stdout] test session::resume::tests::sidecar_path_uses_hex_info_hash ... ok
[INFO] [stdout] test session::resume::tests::unpack_rejects_short_buffer ... ok
[INFO] [stdout] test session::shaper::bucket::tests::demand_accounting_separates_consumed_and_denied ... ok
[INFO] [stdout] test session::shaper::bucket::tests::fresh_bucket_full ... ok
[INFO] [stdout] test session::shaper::bucket::tests::grant_saturates_at_capacity ... ok
[INFO] [stdout] test session::shaper::bucket::tests::passthrough_bucket_still_ticks_refill ... ok
[INFO] [stdout] test session::shaper::bucket::tests::try_consume_fails_over_budget_and_rolls_back ... ok
[INFO] [stdout] test session::shaper::refiller::tests::run_one_tick_grants_at_session_rate ... ok
[INFO] [stdout] test session::shaper::bucket::tests::try_consume_succeeds_within_budget ... ok
[INFO] [stdout] test session::shaper::bucket::tests::wait_for_refill_wakes_on_notify ... ok
[INFO] [stdout] test session::shaper::tests::drop_torrent_removes_peers ... ok
[INFO] [stdout] test session::shaper::tests::refiller_touches_all_three_tiers_even_at_passthrough ... ok
[INFO] [stdout] test session::stats::sink::tests::flush_graceful_is_bounded ... ok
[INFO] [stdout] test session::stats::sink::tests::file_sink_writes_bencode_sidecar ... ok
[INFO] [stdout] test session::stats::tests::retire_peer_preserves_counters ... ok
[INFO] [stdout] test session::stats::tests::snapshot_during_disconnect_never_loses_counters ... ok
[INFO] [stdout] test session::stats::tests::snapshot_sums_live_and_disconnected ... ok
[INFO] [stdout] test session::torrent::tests::adr_0019_alert_ordered_before_peer_broadcast ... ok
[INFO] [stdout] test session::stats::sink::tests::enqueue_deduplicates_on_info_hash ... ok
[INFO] [stdout] test session::torrent::tests::adr_0019_transition_fires_alert_and_broadcasts_not_interested ... ok
[INFO] [stdout] test session::torrent::tests::bep_0027_private_torrent_drain_pex_is_empty_defensively ... ok
[INFO] [stdout] test session::torrent::tests::adr_0019_transition_is_idempotent_under_completion_fired ... ok
[INFO] [stdout] test session::torrent::tests::bep_0027_private_torrent_ignores_inbound_pex ... ok
[INFO] [stdout] test session::torrent::tests::adr_0019_resume_from_complete_skips_transition ... ok
[INFO] [stdout] test session::torrent::tests::bep_0027_private_torrent_sends_no_outbound_pex ... ok
[INFO] [stdout] test session::torrent::tests::bitfield_strict_rejects_nonzero_spare_bits ... ok
[INFO] [stdout] test session::torrent::tests::bitfield_strict_rejects_wrong_length ... ok
[INFO] [stdout] test session::torrent::tests::g1_pause_cancels_outstanding_requests_and_releases_claims ... ok
[INFO] [stdout] test session::torrent::tests::g1_pause_chokes_unchoked_peers_and_keeps_them_choked ... ok
[INFO] [stdout] test session::torrent::tests::g1_pause_is_idempotent_no_duplicate_choke ... ok
[INFO] [stdout] test session::torrent::tests::g1_paused_blocks_auto_unchoke_on_new_interest ... ok
[INFO] [stdout] test session::torrent::tests::g1_resume_unchokes_only_interested_peers ... ok
[INFO] [stdout] test session::torrent::tests::initial_advert_falls_back_to_bitfield_without_fast_ext ... ok
[INFO] [stdout] test session::torrent::tests::initial_advert_uses_bitfield_for_partial_have_even_with_fast_ext ... ok
[INFO] [stdout] test session::torrent::tests::initial_advert_uses_have_all_for_complete_seed_with_fast_ext ... ok
[INFO] [stdout] test session::torrent::tests::initial_advert_uses_have_none_for_empty_leech_with_fast_ext ... ok
[INFO] [stdout] test session::torrent::tests::bitfield_strict_accepts_well_formed ... ok
[INFO] [stdout] test peer_filter::tests::strict_still_rejects_loopback ... ok
[INFO] [stdout] test session::torrent::tests::bitfield_strict_handles_byte_aligned_count ... ok
[INFO] [stdout] test session::torrent::tests::validate_rejects_mismatched_piece_hashes_length ... ok
[INFO] [stdout] test session::torrent::tests::validate_accepts_well_formed ... ok
[INFO] [stdout] test session::torrent::tests::validate_rejects_total_length_overflow ... ok
[INFO] [stdout] test session::torrent::tests::validate_rejects_zero_piece_length ... ok
[INFO] [stdout] test session::torrent::tests::validate_rejects_zero_piece_count ... ok
[INFO] [stdout] test session::udp::demux::tests::dht_does_not_steal_tracker_responses ... ok
[INFO] [stdout] test session::udp::demux::tests::dht_dispatch_routes_bencode_dict_to_subscriber ... ok
[INFO] [stdout] test session::udp::demux::tests::duplicate_txid_rejected ... ok
[INFO] [stdout] test session::torrent::tests::validate_rejects_zero_total_length ... ok
[INFO] [stdout] test session::udp::demux::tests::tracker_txid_roundtrip ... ok
[INFO] [stdout] test storage::file::tests::open_existing ... ok
[INFO] [stdout] test storage::file::tests::rejects_out_of_bounds ... ok
[INFO] [stdout] test storage::file::tests::roundtrip_file ... ok
[INFO] [stdout] test storage::memory::tests::rejects_offset_overflow ... ok
[INFO] [stdout] test session::resume::tests::load_rejects_oversize_sidecar_file ... ok
[INFO] [stdout] test storage::memory::tests::roundtrip_small ... ok
[INFO] [stdout] test peer_id::tests::consecutive_builds_differ_in_suffix ... ok
[INFO] [stdout] test storage::memory::tests::writev_sequential ... ok
[INFO] [stdout] test storage::memory::tests::rejects_out_of_bounds ... ok
[INFO] [stdout] test session::udp::demux::tests::register_dht_twice_rejected ... ok
[INFO] [stdout] test storage::multi_file::tests::component_at_limit_accepted ... ok
[INFO] [stdout] test storage::multi_file::tests::component_too_long_rejected ... ok
[INFO] [stdout] test storage::multi_file::tests::capacity_matches_sum_of_lengths ... ok
[INFO] [stdout] test storage::multi_file::tests::create_from_info_v1_multi_roundtrips ... ok
[INFO] [stdout] test storage::multi_file::tests::current_dir_component_rejected ... ok
[INFO] [stdout] test storage::multi_file::tests::dangling_symlink_leaf_rejected ... ok
[INFO] [stdout] test storage::multi_file::tests::absolute_component_rejected ... ok
[INFO] [stdout] test storage::multi_file::tests::default_cap_is_128 ... ok
[INFO] [stdout] test storage::multi_file::tests::backslash_in_component_rejected ... ok
[INFO] [stdout] test storage::multi_file::tests::duplicate_path_rejected ... ok
[INFO] [stdout] test storage::multi_file::tests::empty_components_rejected ... ok
[INFO] [stdout] test storage::multi_file::tests::empty_string_component_rejected ... ok
[INFO] [stdout] test storage::multi_file::tests::create_makes_files_with_declared_sizes ... ok
[INFO] [stdout] test storage::multi_file::tests::delete_removes_files_and_empty_dirs ... ok
[INFO] [stdout] test storage::multi_file::tests::fd_pool_cache_hit_reuses_fd ... ok
[INFO] [stdout] test storage::multi_file::tests::fd_pool_cap_clamped ... ok
[INFO] [stdout] test storage::multi_file::tests::fd_pool_different_storage_ids_dont_collide ... ok
[INFO] [stdout] test storage::multi_file::tests::from_info_accepts_hybrid ... ok
[INFO] [stdout] test storage::multi_file::tests::from_info_rejects_non_utf8_path ... ok
[INFO] [stdout] test storage::multi_file::tests::from_info_rejects_single_file ... ok
[INFO] [stdout] test storage::multi_file::tests::from_info_rejects_v2_only ... ok
[INFO] [stdout] test storage::multi_file::tests::happy_path_two_files ... ok
[INFO] [stdout] test storage::multi_file::tests::happy_path_with_zero_length ... ok
[INFO] [stdout] test storage::multi_file::tests::open_from_info_resumes_existing ... ok
[INFO] [stdout] test storage::multi_file::tests::nul_byte_in_component_rejected ... ok
[INFO] [stdout] test storage::multi_file::tests::open_rejects_wrong_length ... ok
[INFO] [stdout] test storage::multi_file::tests::overflow_total_length_rejected ... ok
[INFO] [stdout] test storage::multi_file::tests::open_rejects_missing_file ... ok
[INFO] [stdout] test storage::multi_file::tests::parent_dir_component_rejected ... ok
[INFO] [stdout] test storage::multi_file::tests::path_separator_in_component_rejected ... ok
[INFO] [stdout] test storage::multi_file::tests::read_past_capacity_is_out_of_bounds ... ok
[INFO] [stdout] test storage::multi_file::tests::symlink_escape_rejected ... ok
[INFO] [stdout] test storage::multi_file::tests::write_at_offset_zero_and_exact_boundary ... ok
[INFO] [stdout] test storage::multi_file::tests::write_read_spans_three_files_with_zero_in_middle ... ok
[INFO] [stdout] test storage::multi_file::tests::write_read_spans_two_files ... ok
[INFO] [stdout] test storage::multi_file::tests::write_read_within_single_file ... ok
[INFO] [stdout] test session::udp::demux::tests::short_packet_counted_as_unmatched ... ok
[INFO] [stdout] test tracker::compact::tests::decode_v4_drops_zero_port ... ok
[INFO] [stdout] test session::udp::demux::tests::dht_dispatch_drops_when_no_subscriber_registered ... ok
[INFO] [stdout] test storage::multi_file::tests::fd_pool_evicts_lru_at_cap ... ok
[INFO] [stdout] test tracker::compact::tests::decode_v4_rejects_truncated ... ok
[INFO] [stdout] test storage::multi_file::tests::fd_pool_eviction_does_not_close_held_arc ... ok
[INFO] [stdout] test tracker::compact::tests::decode_v4_two_peers ... ok
[INFO] [stdout] test tracker::http::tests::build_scrape_url_encodes_multiple_hashes ... ok
[INFO] [stdout] test tracker::compact::tests::decode_v6_rejects_truncated ... ok
[INFO] [stdout] test tracker::compact::tests::decode_v6_one_peer ... ok
[INFO] [stdout] test tracker::http::tests::build_scrape_url_rejects_empty_base ... ok
[INFO] [stdout] test tracker::http::tests::build_scrape_url_rejects_empty_hashes ... ok
[INFO] [stdout] test tracker::http::tests::build_scrape_url_preserves_query_string ... ok
[INFO] [stdout] test tracker::http::tests::parse_compact_v4_response ... ok
[INFO] [stdout] test tracker::http::tests::parse_dict_peers ... ok
[INFO] [stdout] test tracker::http::tests::parse_failure_reason ... ok
[INFO] [stdout] test tracker::http::tests::build_scrape_url_rejects_url_without_announce_segment ... ok
[INFO] [stdout] test tracker::http::tests::parse_scrape_response_basic ... ok
[INFO] [stdout] test tracker::http::tests::parse_scrape_response_rejects_bad_key_length ... ok
[INFO] [stdout] test tracker::http::tests::parse_scrape_response_rejects_missing_counter_fields ... ok
[INFO] [stdout] test tracker::http::tests::parse_scrape_response_rejects_missing_files ... ok
[INFO] [stdout] test tracker::http::tests::parse_scrape_response_surfaces_failure ... ok
[INFO] [stdout] test tracker::http::tests::build_scrape_url_rewrites_announce_segment ... ok
[INFO] [stdout] test tracker::http::tests::parse_scrape_response_with_name ... ok
[INFO] [stdout] test tracker::http::tests::rejects_negative_interval ... ok
[INFO] [stdout] test tracker::http::tests::rejects_zero_interval ... ok
[INFO] [stdout] test tracker::http::tests::rejects_zero_min_interval ... ok
[INFO] [stdout] test tracker::http::tests::url_encodes_binary_hash_byte_for_byte ... ok
[INFO] [stdout] test tracker::http::tests::url_rejects_empty_base ... ok
[INFO] [stdout] test tracker::http::tests::url_uses_ampersand_when_base_has_query ... ok
[INFO] [stdout] test tracker::tiered::tests::all_trackers_failing_returns_last_error ... ok
[INFO] [stdout] test tracker::tiered::tests::empty_tiers_filtered ... ok
[INFO] [stdout] test tracker::tiered::tests::success_promotes_tracker_to_tier_head ... ok
[INFO] [stdout] test tracker::tiered::tests::tier_fall_through_tries_tier_1_when_tier_0_fails ... ok
[INFO] [stdout] test tracker::udp::tests::announce_request_layout ... ok
[INFO] [stdout] test tracker::udp::tests::connect_request_has_protocol_id_first ... ok
[INFO] [stdout] test tracker::udp::tests::decode_announce_parses_peer_list ... ok
[INFO] [stdout] test tracker::udp::tests::decode_connect_propagates_tracker_error ... ok
[INFO] [stdout] test tracker::udp::tests::decode_connect_rejects_wrong_txid ... ok
[INFO] [stdout] test tracker::udp::tests::decode_connect_roundtrip ... ok
[INFO] [stdout] test tracker::udp::tests::decode_scrape_propagates_tracker_error ... ok
[INFO] [stdout] test tracker::udp::tests::decode_scrape_rejects_wrong_size ... ok
[INFO] [stdout] test tracker::udp::tests::decode_scrape_rejects_wrong_txid ... ok
[INFO] [stdout] test tracker::udp::tests::decode_scrape_roundtrips_two_hashes ... ok
[INFO] [stdout] test tracker::udp::tests::encode_scrape_lays_out_header_and_hashes ... ok
[INFO] [stdout] test tracker::udp::tests::encode_scrape_rejects_empty_input ... ok
[INFO] [stdout] test tracker::udp::tests::retry_timeout_follows_bep15_curve ... ok
[INFO] [stdout] test tracker::udp::tests::encode_scrape_rejects_too_many_hashes ... ok
[INFO] [stdout] test tracker::udp::tests::udp_tracker_connects_and_announces ... ok
[INFO] [stdout] test tracker::udp::tests::udp_tracker_scrape_end_to_end ... ok
[INFO] [stdout] test tracker::udp::tests::udp_tracker_surfaces_tracker_error ... ok
[INFO] [stdout] test tracker::udp::tests::udp_tracker_caches_connection_id_across_announces ... ok
[INFO] [stdout] test session::shaper::refiller::tests::refill_loop_ticks_at_configured_interval ... ok
[INFO] [stdout] test lsd::tests::bind_multicast_reuse_addr ... FAILED
[INFO] [stdout] test storage::multi_file::tests::too_many_entries_rejected ... ok
[INFO] [stdout] test tracker::udp::tests::udp_tracker_times_out_when_no_response ... ok
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout] 
[INFO] [stdout] ---- lsd::tests::bind_multicast_reuse_addr stdout ----
[INFO] [stdout] 
[INFO] [stdout] thread 'lsd::tests::bind_multicast_reuse_addr' (72) panicked at crates/magpie-bt-core/src/lsd.rs:804:42:
[INFO] [stdout] first bind failed: Os { code: 19, kind: Uncategorized, message: "No such device" }
[INFO] [stdout] stack backtrace:
[INFO] [stdout]    0:     0x5da04a0479ba - std[1209cfb42f6d497f]::backtrace_rs::backtrace::libunwind::trace
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/../../backtrace/src/backtrace/libunwind.rs:117:9
[INFO] [stdout]    1:     0x5da04a0479ba - std[1209cfb42f6d497f]::backtrace_rs::backtrace::trace_unsynchronized::<std[1209cfb42f6d497f]::sys::backtrace::_print_fmt::{closure#1}>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/../../backtrace/src/backtrace/mod.rs:66:14
[INFO] [stdout]    2:     0x5da04a0479ba - std[1209cfb42f6d497f]::sys::backtrace::_print_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:74:9
[INFO] [stdout]    3:     0x5da04a0479ba - <<std[1209cfb42f6d497f]::sys::backtrace::BacktraceLock>::print::DisplayBacktrace as core[4a92201981c6b77a]::fmt::Display>::fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:44:26
[INFO] [stdout]    4:     0x5da04a05d79a - <core[4a92201981c6b77a]::fmt::rt::Argument>::fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/fmt/rt.rs:152:76
[INFO] [stdout]    5:     0x5da04a05d79a - core[4a92201981c6b77a]::fmt::write
[INFO] [stdout]    6:     0x5da04a04cbf2 - std[1209cfb42f6d497f]::io::default_write_fmt::<alloc[420bb310a727dfd7]::vec::Vec<u8>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/io/mod.rs:621:11
[INFO] [stdout]    7:     0x5da04a04cbf2 - <alloc[420bb310a727dfd7]::vec::Vec<u8> as std[1209cfb42f6d497f]::io::Write>::write_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/io/mod.rs:1976:13
[INFO] [stdout]    8:     0x5da04a022b0f - <std[1209cfb42f6d497f]::sys::backtrace::BacktraceLock>::print
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:47:9
[INFO] [stdout]    9:     0x5da04a022b0f - std[1209cfb42f6d497f]::panicking::default_hook::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:292:27
[INFO] [stdout]   10:     0x5da04a03e119 - std[1209cfb42f6d497f]::panicking::default_hook
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:316:9
[INFO] [stdout]   11:     0x5da049ef0bfc - <alloc[420bb310a727dfd7]::boxed::Box<dyn for<'a, 'b> core[4a92201981c6b77a]::ops::function::Fn<(&'a std[1209cfb42f6d497f]::panic::PanicHookInfo<'b>,), Output = ()> + core[4a92201981c6b77a]::marker::Sync + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::Fn<(&std[1209cfb42f6d497f]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   12:     0x5da049ef0bfc - test[59e67a9bc0e119f1]::test_main_inner::<test[59e67a9bc0e119f1]::test_main_static::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:155:21
[INFO] [stdout]   13:     0x5da04a03e392 - <alloc[420bb310a727dfd7]::boxed::Box<dyn for<'a, 'b> core[4a92201981c6b77a]::ops::function::Fn<(&'a std[1209cfb42f6d497f]::panic::PanicHookInfo<'b>,), Output = ()> + core[4a92201981c6b77a]::marker::Sync + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::Fn<(&std[1209cfb42f6d497f]::panic::PanicHookInfo,)>>::call
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2285:9
[INFO] [stdout]   14:     0x5da04a03e392 - std[1209cfb42f6d497f]::panicking::panic_with_hook
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:833:13
[INFO] [stdout]   15:     0x5da04a022bc8 - std[1209cfb42f6d497f]::panicking::panic_handler::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:698:13
[INFO] [stdout]   16:     0x5da04a017a09 - std[1209cfb42f6d497f]::sys::backtrace::__rust_end_short_backtrace::<std[1209cfb42f6d497f]::panicking::panic_handler::{closure#0}, !>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:182:18
[INFO] [stdout]   17:     0x5da04a023c1d - __rustc[4effac393e9f3da6]::rust_begin_unwind
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:689:5
[INFO] [stdout]   18:     0x5da04a05df5c - core[4a92201981c6b77a]::panicking::panic_fmt
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panicking.rs:80:14
[INFO] [stdout]   19:     0x5da04a05dcd2 - core[4a92201981c6b77a]::result::unwrap_failed
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/result.rs:1867:5
[INFO] [stdout]   20:     0x5da049eb5783 - <core[4a92201981c6b77a]::result::Result<tokio[d833f7a882a31842]::net::udp::UdpSocket, std[1209cfb42f6d497f]::io::error::Error>>::expect
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/result.rs:1185:23
[INFO] [stdout]   21:     0x5da049e5bb08 - magpie_bt_core[d700483a7678bb2a]::lsd::tests::bind_multicast_reuse_addr::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/magpie-bt-core/src/lsd.rs:804:42
[INFO] [stdout]   22:     0x5da049ea3152 - <core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>> as core[4a92201981c6b77a]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/future/future.rs:133:9
[INFO] [stdout]   23:     0x5da049ea31cd - <core[4a92201981c6b77a]::pin::Pin<&mut core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>>> as core[4a92201981c6b77a]::future::future::Future>::poll
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/future/future.rs:133:9
[INFO] [stdout]   24:     0x5da049e4013d - <tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[4a92201981c6b77a]::pin::Pin<&mut core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:778:70
[INFO] [stdout]   25:     0x5da049e4003b - tokio[d833f7a882a31842]::task::coop::with_budget::<core[4a92201981c6b77a]::task::poll::Poll<()>, <tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[4a92201981c6b77a]::pin::Pin<&mut core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/task/coop/mod.rs:167:5
[INFO] [stdout]   26:     0x5da049e4003b - tokio[d833f7a882a31842]::task::coop::budget::<core[4a92201981c6b77a]::task::poll::Poll<()>, <tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[4a92201981c6b77a]::pin::Pin<&mut core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/task/coop/mod.rs:133:5
[INFO] [stdout]   27:     0x5da049e4003b - <tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[4a92201981c6b77a]::pin::Pin<&mut core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:778:25
[INFO] [stdout]   28:     0x5da049e33c80 - <tokio[d833f7a882a31842]::runtime::scheduler::current_thread::Context>::enter::<core[4a92201981c6b77a]::task::poll::Poll<()>, <tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[4a92201981c6b77a]::pin::Pin<&mut core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>>>>::{closure#0}::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:451:19
[INFO] [stdout]   29:     0x5da049e3f35d - <tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[4a92201981c6b77a]::pin::Pin<&mut core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:777:44
[INFO] [stdout]   30:     0x5da049e3e604 - <tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[4a92201981c6b77a]::pin::Pin<&mut core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>>>>::{closure#0}, core[4a92201981c6b77a]::option::Option<()>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:865:68
[INFO] [stdout]   31:     0x5da049da7a3b - <tokio[d833f7a882a31842]::runtime::context::scoped::Scoped<tokio[d833f7a882a31842]::runtime::scheduler::Context>>::set::<<tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[4a92201981c6b77a]::pin::Pin<&mut core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>>>>::{closure#0}, core[4a92201981c6b77a]::option::Option<()>>::{closure#0}, (alloc[420bb310a727dfd7]::boxed::Box<tokio[d833f7a882a31842]::runtime::scheduler::current_thread::Core>, core[4a92201981c6b77a]::option::Option<()>)>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/context/scoped.rs:40:9
[INFO] [stdout]   32:     0x5da049e58779 - tokio[d833f7a882a31842]::runtime::context::set_scheduler::<(alloc[420bb310a727dfd7]::boxed::Box<tokio[d833f7a882a31842]::runtime::scheduler::current_thread::Core>, core[4a92201981c6b77a]::option::Option<()>), <tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[4a92201981c6b77a]::pin::Pin<&mut core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>>>>::{closure#0}, core[4a92201981c6b77a]::option::Option<()>>::{closure#0}>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/context.rs:181:38
[INFO] [stdout]   33:     0x5da049eb02f2 - <std[1209cfb42f6d497f]::thread::local::LocalKey<tokio[d833f7a882a31842]::runtime::context::Context>>::try_with::<tokio[d833f7a882a31842]::runtime::context::set_scheduler<(alloc[420bb310a727dfd7]::boxed::Box<tokio[d833f7a882a31842]::runtime::scheduler::current_thread::Core>, core[4a92201981c6b77a]::option::Option<()>), <tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[4a92201981c6b77a]::pin::Pin<&mut core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>>>>::{closure#0}, core[4a92201981c6b77a]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[420bb310a727dfd7]::boxed::Box<tokio[d833f7a882a31842]::runtime::scheduler::current_thread::Core>, core[4a92201981c6b77a]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/local.rs:462:12
[INFO] [stdout]   34:     0x5da049eafe7e - <std[1209cfb42f6d497f]::thread::local::LocalKey<tokio[d833f7a882a31842]::runtime::context::Context>>::with::<tokio[d833f7a882a31842]::runtime::context::set_scheduler<(alloc[420bb310a727dfd7]::boxed::Box<tokio[d833f7a882a31842]::runtime::scheduler::current_thread::Core>, core[4a92201981c6b77a]::option::Option<()>), <tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[4a92201981c6b77a]::pin::Pin<&mut core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>>>>::{closure#0}, core[4a92201981c6b77a]::option::Option<()>>::{closure#0}>::{closure#0}, (alloc[420bb310a727dfd7]::boxed::Box<tokio[d833f7a882a31842]::runtime::scheduler::current_thread::Core>, core[4a92201981c6b77a]::option::Option<()>)>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/local.rs:426:20
[INFO] [stdout]   35:     0x5da049e5681d - tokio[d833f7a882a31842]::runtime::context::set_scheduler::<(alloc[420bb310a727dfd7]::boxed::Box<tokio[d833f7a882a31842]::runtime::scheduler::current_thread::Core>, core[4a92201981c6b77a]::option::Option<()>), <tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::enter<<tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[4a92201981c6b77a]::pin::Pin<&mut core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>>>>::{closure#0}, core[4a92201981c6b77a]::option::Option<()>>::{closure#0}>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/context.rs:181:17
[INFO] [stdout]   36:     0x5da049e39700 - <tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::enter::<<tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::block_on<core[4a92201981c6b77a]::pin::Pin<&mut core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>>>>::{closure#0}, core[4a92201981c6b77a]::option::Option<()>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:865:27
[INFO] [stdout]   37:     0x5da049e399f6 - <tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CoreGuard>::block_on::<core[4a92201981c6b77a]::pin::Pin<&mut core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:765:24
[INFO] [stdout]   38:     0x5da049e3e110 - <tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>>>::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:205:33
[INFO] [stdout]   39:     0x5da049e0d2c3 - tokio[d833f7a882a31842]::runtime::context::runtime::enter_runtime::<<tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CurrentThread>::block_on<core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>>>::{closure#0}, ()>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/context/runtime.rs:65:16
[INFO] [stdout]   40:     0x5da049e33451 - <tokio[d833f7a882a31842]::runtime::scheduler::current_thread::CurrentThread>::block_on::<core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/scheduler/current_thread/mod.rs:193:9
[INFO] [stdout]   41:     0x5da049ec46f4 - <tokio[d833f7a882a31842]::runtime::runtime::Runtime>::block_on_inner::<core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/runtime.rs:371:52
[INFO] [stdout]   42:     0x5da049ec48bf - <tokio[d833f7a882a31842]::runtime::runtime::Runtime>::block_on::<core[4a92201981c6b77a]::pin::Pin<&mut dyn core[4a92201981c6b77a]::future::future::Future<Output = ()>>>
[INFO] [stdout]                                at /opt/rustwide/cargo-home/registry/src/index.crates.io-1949cf8c6b5b557f/tokio-1.51.1/src/runtime/runtime.rs:345:18
[INFO] [stdout]   43:     0x5da049e621f9 - magpie_bt_core[d700483a7678bb2a]::lsd::tests::bind_multicast_reuse_addr
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/magpie-bt-core/src/lsd.rs:807:20
[INFO] [stdout]   44:     0x5da049e5aa47 - magpie_bt_core[d700483a7678bb2a]::lsd::tests::bind_multicast_reuse_addr::{closure#0}
[INFO] [stdout]                                at /opt/rustwide/workdir/crates/magpie-bt-core/src/lsd.rs:801:41
[INFO] [stdout]   45:     0x5da049ea7316 - <magpie_bt_core[d700483a7678bb2a]::lsd::tests::bind_multicast_reuse_addr::{closure#0} as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   46:     0x5da049ee3edb - <fn() -> core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   47:     0x5da049ee3edb - test[59e67a9bc0e119f1]::__rust_begin_short_backtrace::<core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>, fn() -> core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:724:18
[INFO] [stdout]   48:     0x5da049ef16cb - test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:747:74
[INFO] [stdout]   49:     0x5da049ef16cb - <core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   50:     0x5da049ef16cb - std[1209cfb42f6d497f]::panicking::catch_unwind::do_call::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>, core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   51:     0x5da049ef16cb - std[1209cfb42f6d497f]::panicking::catch_unwind::<core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>, core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   52:     0x5da049ef16cb - std[1209cfb42f6d497f]::panic::catch_unwind::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<test[59e67a9bc0e119f1]::run_test_in_process::{closure#0}>, core[4a92201981c6b77a]::result::Result<(), alloc[420bb310a727dfd7]::string::String>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   53:     0x5da049ef16cb - test[59e67a9bc0e119f1]::run_test_in_process
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:747:27
[INFO] [stdout]   54:     0x5da049ef16cb - test[59e67a9bc0e119f1]::run_test::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:668:43
[INFO] [stdout]   55:     0x5da049eeade4 - test[59e67a9bc0e119f1]::run_test::{closure#1}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/test/src/lib.rs:698:41
[INFO] [stdout]   56:     0x5da049eeade4 - std[1209cfb42f6d497f]::sys::backtrace::__rust_begin_short_backtrace::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/backtrace.rs:166:18
[INFO] [stdout]   57:     0x5da049ef42d2 - std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/lifecycle.rs:70:13
[INFO] [stdout]   58:     0x5da049ef42d2 - <core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/panic/unwind_safe.rs:275:9
[INFO] [stdout]   59:     0x5da049ef42d2 - std[1209cfb42f6d497f]::panicking::catch_unwind::do_call::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:581:40
[INFO] [stdout]   60:     0x5da049ef42d2 - std[1209cfb42f6d497f]::panicking::catch_unwind::<(), core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panicking.rs:544:19
[INFO] [stdout]   61:     0x5da049ef42d2 - std[1209cfb42f6d497f]::panic::catch_unwind::<core[4a92201981c6b77a]::panic::unwind_safe::AssertUnwindSafe<std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}::{closure#0}>, ()>
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/panic.rs:359:14
[INFO] [stdout]   62:     0x5da049ef42d2 - std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked::<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/thread/lifecycle.rs:68:26
[INFO] [stdout]   63:     0x5da049ef42d2 - <std[1209cfb42f6d497f]::thread::lifecycle::spawn_unchecked<test[59e67a9bc0e119f1]::run_test::{closure#1}, ()>::{closure#1} as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once::{shim:vtable#0}
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/core/src/ops/function.rs:250:5
[INFO] [stdout]   64:     0x5da04a046b7f - <alloc[420bb310a727dfd7]::boxed::Box<dyn core[4a92201981c6b77a]::ops::function::FnOnce<(), Output = ()> + core[4a92201981c6b77a]::marker::Send> as core[4a92201981c6b77a]::ops::function::FnOnce<()>>::call_once
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/alloc/src/boxed.rs:2271:9
[INFO] [stdout]   65:     0x5da04a046b7f - <std[1209cfb42f6d497f]::sys::thread::unix::Thread>::new::thread_start
[INFO] [stdout]                                at /rustc/f9988fefd3add01f414f52b414308e7872622fee/library/std/src/sys/thread/unix.rs:118:17
[INFO] [stdout]   66:     0x7d0d6ff79aa4 - <unknown>
[INFO] [stdout]   67:     0x7d0d70006a64 - clone
[INFO] [stdout]   68:                0x0 - <unknown>
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] failures:
[INFO] [stdout]     lsd::tests::bind_multicast_reuse_addr
[INFO] [stdout] 
[INFO] [stdout] test result: FAILED. 252 passed; 1 failed; 1 ignored; 0 measured; 0 filtered out; finished in 0.57s
[INFO] [stdout] 
[INFO] [stderr] error: test failed, to rerun pass `-p magpie-bt-core --lib`
[INFO] running `Command { std: "docker" "inspect" "d5dfe86310ddf5b2a5a8e4207724a3a77f0cfd4e2f6ddaa7ed838529845382f5", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "d5dfe86310ddf5b2a5a8e4207724a3a77f0cfd4e2f6ddaa7ed838529845382f5", kill_on_drop: false }`
[INFO] [stdout] d5dfe86310ddf5b2a5a8e4207724a3a77f0cfd4e2f6ddaa7ed838529845382f5
